* Долго доставляются письма

Новости

12.10.2009
Переезд завершен

POP2-->SMTP
Eserv работает в следующем режиме: в каждые 3 минуты забирает письма ящиков в списке Boxes.txt и переправляет их на внутренний SMTP server на Lotus Domino 6.5
Вопрос в следующем: почему большие письма доставляются очень медленно? Например письмо размером 20 Mb пришел только через час, хотя все это время оно уже лежало на foxship@zdship.ru.

Автор

Дата

Текст

tags

28.05.2010 18:12

А получателей (в заголовке "To:") у этого большого письма сколько?

wikipost

foxship

28.05.2010 18:25

Один единственный.

wikipost

28.05.2010 18:34
ред: 28.05.2010 18:37

pop2smtp в Boxes настроен отправлять его через acSMTP (и далее по алиасу на Лотус), или сразу на smtp Лотуса? Лог работы pop2smtp вышлите на support@eserv.ru, пожалуйста.

wikipost

foxship

28.05.2010 18:52

Сразу на лотус. А лог такой огромный — 160 Mb за день. Это тоже мне кажется ненормальным. Так что весь выслать не смогу.

wikipost

28.05.2010 22:52

Тогда загляните в этот лог и посмотрите (поиском по отправителю или message-id), сколько раз там фигурирует это большое письмо. Возможно, что, пока pop2smtp скачивает сообщение, smtp-получатель уже успевает отключиться по таймауту. Или в логе лотуса наверное тоже можно посмотреть список незавершенных сессий. Если в лотусе можно подкрутить smtp-таймаут, то попробуйте увеличить его до величины, за которую у вас скачиваются 20Мб по POP3.

wikipost

foxship

29.05.2010 08:49
ред: 29.05.2010 08:51

ac пишет: Тогда загляните в этот лог и посмотрите (поиском по отправителю или message-id), сколько раз там фигурирует это большое письмо.

Вот этот блок я нашел шесть раз

pop3 (912): Return-Path: <FoxShip@yandex.ru> pop3 (912): X-Original-To: foxship@zdship.ru pop3 (912): Delivered-To: foxship@zdship.ru pop3 (912): Received: from forward3.mail.yandex.net (forward3.mail.yandex.net [77.88.46.8]) pop3 (912): by mx24.valuehost.ru (Postfix) with ESMTP id 35A7A4AC41 pop3 (912): for <foxship@zdship.ru>; Fri, 28 May 2010 14:47:13 +0400 (MSD) pop3 (912): Received: from web58.yandex.ru (web58.yandex.ru [77.88.47.164]) pop3 (912): by forward3.mail.yandex.net (Yandex) with ESMTP id 742B556D8203 pop3 (912): for <foxship@zdship.ru>; Fri, 28 May 2010 14:46:09 +0400 (MSD) pop3 (912): DKIM-Signature: v=1; a=rsa-sha256; c=relaxed/relaxed; d=yandex.ru; s=mail; pop3 (912): t=1275043594; bh=aruXDecEFt+pZ4LpPT4b1uy8AT01Eu7ppBgq4wz+AJA=; pop3 (912): h=From:To:Subject:MIME-Version:Message-Id:Date:Content-Type; pop3 (912): b=OwdjJcYO4XWssEwKNYCOGf1IoGa+NqgwpOjKV/USk0X6hgdjMVxo7Sh/PWQEcRf/0 pop3 (912): ocjdTgDK3foNZQl92nmyijvkAFdjsOSFD9P+fq7h0itey8spDwg9w7FMpmUHIX5qtm pop3 (912): AmUVpfdS6GqlXWkBgyjkk37GprkJLxcQyWkgrr50= pop3 (912): Received: from localhost (localhost.localdomain [127.0.0.1]) pop3 (912): by web58.yandex.ru (Yandex) with ESMTP id 0145BC2808E pop3 (912): for <foxship@zdship.ru>; Fri, 28 May 2010 14:46:09 +0400 (MSD) pop3 (912): X-Yandex-Spam: 1 pop3 (912): X-Yandex-Front: web58.yandex.ru pop3 (912): X-Yandex-TimeMark: 1275043568 pop3 (912): Received: from 150.188.23.217.in-addr.arpa (150.188.23.217.in-addr.arpa [217.23.188.150]) by mail.yandex.ru with HTTP; pop3 (912): Fri, 28 May 2010 14:46:00 +0400 pop3 (912): From: =?koi8-r?B?7NXLyc4g99HexdPMwdc=?= <FoxShip@yandex.ru> pop3 (912): To: foxship@zdship.ru pop3 (912): Subject: test3 pop3 (912): MIME-Version: 1.0 pop3 (912): Message-Id: <477481275043560@web58.yandex.ru> pop3 (912): Date: Fri, 28 May 2010 14:46:00 +0400 pop3 (912): X-Mailer: Yamail [ http://yandex.ru ] 5.0 pop3 (912): Content-Type: multipart/mixed; pop3 (912): boundary="----==--bound.47749.web58.yandex.ru"

wikipost

pig

29.05.2010 23:21

Значит, было шесть попыток его выкачать. Хотелось бы знать, что там в логе дальше было про это письмо. В смысле — чем каждая попытка заканчивалась.

wikipost

foxship

31.05.2010 09:55
ред: 31.05.2010 10:10

pig пишет:
Значит, было шесть попыток его выкачать. Хотелось бы знать, что там в логе дальше было про это письмо. В смысле — чем каждая попытка заканчивалась.

В первых двух случаях (фрагменты логов между сессиями или сеансами)

pop3 (2380): YM2Nw5Wbq3I6GHbg8zc01zSfFtsEJO8bN1ktDbC2JUg3vYUBKLQV449FdQz4XBc76Met3/q3OvoX smtp (2380): YM2Nw5Wbq3I6GHbg8zc01zSfFtsEJO8bN1ktDbC2JUg3vYUBKLQV449FdQz4XBc76Met3/q3OvoX pop3 (3464): +OK Hello there. pop3 (3464): USER zdship@nimb.ru

pop3 (3464): h9JmMQQfIZY4HfVsCHCxlY2hLx0Fv6Av8JhWvQz5caB6nGzyt+Fw2OPbkg/fw/cv3bgSfZfqeQUk smtp (3464): h9JmMQQfIZY4HfVsCHCxlY2hLx0Fv6Av8JhWvQz5caB6nGzyt+Fw2OPbkg/fw/cv3bgSfZfqeQUk pop3 (3464): 9BiaAnrlisMYyT8BroBny4H0J9lzlGMZ3dO7mGHX/W430+YKfijBz0XxIgPf3v8h69W7/6/+IFS9 pop3 (912): +OK Hello there. pop3 (912): USER zdship@nimb.ru

А в последнем

smtp (912): 250 Message accepted for delivery pop3 (912): DELE 1 pop3 (912): +OK done. smtp (912): QUIT smtp (912): 221 server-ls.zdship.gor SMTP Service closing transmission channel pop3 (912): QUIT pop3 (912): +OK shutting down. pop3 (912): +OK Hello there. pop3 (912): USER rezatdinov@zdship.ru

Т.е. получается что в начале связь (передача? прием?) обрывалась без объяснения причин, а в последнем письмо прошло успешно и POP2SMTP перешел на следующий по списку в boxes.txt ящик (rezatdinov@zdship.ru)

wikipost

pig

31.05.2010 13:41

Именно так. Причину срывов можно поискать в протокольном логе SMTP и/или в acSMTP.log.

wikipost

foxship

31.05.2010 14:44
ред: 31.05.2010 14:51

pig пишет: Именно так. Причину срывов можно поискать в протокольном логе SMTP и/или в acSMTP.log.

в протокольном логе SMTP нет ничего
А в acSMTP.log посмотрел и увидел, что службу перезагружали несколько раз как раз в это время. Почему (и кто) обрывы я выяснил.
Но вопрос остается. Почему все таки очень долго идет закачка письма.
20-мегабайтное письмо 40-50 минут а то и дольше. Нельзя ли ускорить этот процесс?

Скорость соединения с интернетом — 5-10Mb/c

wikipost

31.05.2010 17:07

Письма качаются с той скоростью, с какой их отдает POP3-сервер. У вас задержка была связана с тем, что письмо скачивалось шесть раз (и с интервалом между этими скачиваниями, соответственно). В нормальной ситуации такого многократного скачивания ес-но быть не должно. Если ситуация повторяется, то надо искать причину разрыва сессий (ведущих к повторным скачиваниям). На мой взгляд наиболее вероятной причиной (если задерживаются только большие письма) является короткий таймаут на принимающей стороне.

wikipost

foxship

31.05.2010 22:33

ac пишет: Письма качаются с той скоростью, с какой их отдает POP3-сервер. У вас задержка была связана с тем, что письмо скачивалось шесть раз (и с интервалом между этими скачиваниями, соответственно). В нормальной ситуации такого многократного скачивания ес-но быть не должно. Если ситуация повторяется, то надо искать причину разрыва сессий (ведущих к повторным скачиваниям). На мой взгляд наиболее вероятной причиной (если задерживаются только большие письма) является короткий таймаут на принимающей стороне.

Причина разрыва сессий устранена. Но 20-ти мегабайтное письмо с момента начала скачивалось 50 минут. Это ненормально. Раньше, когда был линукс, (fetchmail, sendmail) письма скачивались моментально с тех же POP3 серверов.

wikipost

foxship

01.06.2010 09:17
ред: 01.06.2010 13:00

ac пишет: Письма качаются с той скоростью, с какой их отдает POP3-сервер.

Поставил 2 эксперимента.

Принял почту с foxship@zdship.ru на MS Outlook Express. То же самое письмо размером в 20 Mb скачал за 10 секунд.

Отправил с того же MS Outlook Express то же самое письмо на Lotus и результат тоже ожидаемый — 10-15 секунд

Исходя из вышесказанного делаю вывод — торможение происходит на Eserv3.

wikipost

01.06.2010 13:03
ред: 01.06.2010 13:04

Меня шокирует такой разброс. 50 минут и 10 секунд

Меня тоже

Особенно удивительно, как получилось, что сначала у вас 20Мбайтное письмо скачалось 6 раз за час, а теперь только один раз за 50 минут.

Давайте все-таки посмотрим лог доставки этого письма на стороне получающего сервера, чтобы посмотреть отметки времени (сам pop2smtp в Eserv/3 не пишет в лог дату/время). Если в лотусе этот лог получить нет возможности, то поставьте для теста принимающим сервером SMTP Eserv'а, и возьмите его лог в DATA\log\smtp\201006log.txt

wikipost

foxship

01.06.2010 13:33
ред: 01.06.2010 13:53

ac пишет: Меня тоже Особенно удивительно, как получилось, что сначала у вас 20Мбайтное письмо скачалось 6 раз за час, а теперь только один раз за 50 минут.

Я уже отписал, что причина обрыва POP2SMTP сессии установлена. Один чудик без моего ведома останавливал и запускал acSMTP. Теперь, когда никто не "хулиганит", письмо доставляется за один раз, но очень долго — 50 минут.

ac пишет: Если в лотусе этот лог получить нет возможности,

Время — 50 минут как раз установлено по логу Лотуса.
Т.е. время с начала скачивания до момента доставки прошло 50 минут. Это железный факт — проверял неоднократно.

wikipost

01.06.2010 14:22

Чтобы определить, где именно задержка, мне нужен полный лог SMTP-сессии. Вышлите его, пожалуйста, на support@eserv.ru.

wikipost

foxship

01.06.2010 14:48

ac пишет: мне нужен полный лог SMTP-сессии

Вы хотели сказать pop2smtp — сессии?

wikipost

01.06.2010 15:23

Лотус располагает только SMTP-частью этой сессии.

ac пишет: (сам pop2smtp в Eserv/3 не пишет в лог дату/время)

wikipost

foxship

01.06.2010 16:26
ред: 01.06.2010 16:30

28.05.2010 16:42:16 SMTP Server: Message 0045C9B4 (MessageID: <477481275043560@web58.yandex.ru>) received
28.05.2010 16:42:16 SMTP Server: server-gk.zdship.gor (192.168.0.24) disconnected. 1 message[s] received
28.05.2010 16:42:17 SMTP Server: server-gk.zdship.gor (192.168.0.24) connected
28.05.2010 16:42:18 SMTP Server: Message 0045CA90 (MessageID: <E1OHwg1-0001z0-1B@rusfootb.fixame.com>) received
28.05.2010 16:42:18 SMTP Server: server-gk.zdship.gor (192.168.0.24) disconnected. 1 message[s] received
28.05.2010 16:42:19 SMTP Server: server-gk.zdship.gor (192.168.0.24) connected
28.05.2010 16:42:24 Router: Message 0045C9B4 delivered to Вячеслав Михайлович Лукин/ZDSHIP

Здесь как положено за 8 секунд письмо принято. А где оно болталось еще 49 с лишним минут все ровно непонятно.
Скачивание с POP3 сервера, как вы понимаете, началось задолго до 16:42:16.

wikipost

01.06.2010 16:54

foxship пишет: Скачивание с POP3 сервера, как вы понимаете, началось задолго до 16:42:16.

Нет, не понимаю. POP2SMTP подключается к smtp-серверу сразу же после подключения к pop3-серверу, еще до приёма собственно писем. Т.е. по данному логу так:

Подключение к POP3
Подключение к SMTP (16:42:17 SMTP Server: server-gk.zdship.gor (192.168.0.24) connected)
Приём первого письма (16:42:18 SMTP Server: Message 0045CA90 received)
Раз нет других писем, то отключение (16:42:18 SMTP Server: server-gk.zdship.gor (192.168.0.24) disconnected.)

В логе есть и огрызки другой сессии — про выделенное вами письмо 0045C9B4. Начала сессии там нет, есть только received и disconnected. 8 секунд вообще не про эту сессию, хотя и про это письмо — это уже после отключения отправителя Лотус 8 секунд его локально доставлял (delivered). Если именно это письмо искомое, то надо выше по логу найти еще один "(192.168.0.24) connected", и от него и считать до "16:42:16 ... (192.168.0.24) disconnected".

wikipost

foxship

01.06.2010 22:13
ред: 02.06.2010 07:06

ac пишет: Нет, не понимаю.

Я прошу прощения. Немного запутал вас. Но простительно — я не постмастер.
Вот результаты чистого ночного эксперимента с тем же письмом:

01.06.2010 22:13:15 SMTP Server: server-gk.zdship.gor (192.168.0.24) connected 01.06.2010 23:06:18 SMTP Server: Message 0068EE8A (MessageID: <45971275412740@web89.yandex.ru>) received 01.06.2010 23:06:18 SMTP Server: server-gk.zdship.gor (192.168.0.24) disconnected. 1 message[s] received 01.06.2010 23:06:34 Router: Message 0068EE8A delivered to Вячеслав Михайлович Лукин/ZDSHIP

wikipost

02.06.2010 07:16

Да, теперь понятнее, спасибо. А более подробного лога у Лотуса нет? Чтобы на уровне SMTP-команд видеть задержки.

Если нет, то можно слегка подкрутить форматные строки pop2smtp — добавить туда дамп времени. Файл CommonPlugins\log.str.txt заменить строки

940 *{Dirs[Logs]}\pop2smtp\{MMDD}debug.txt*pop3 ({GetCurrentThreadId}): {s} 941 *{Dirs[Logs]}\pop2smtp\{MMDD}debug.txt*smtp ({GetCurrentThreadId}): {s}

на

940 *{Dirs[Logs]}\pop2smtp\{MMDD}debug.txt*{hh:mm:ss} pop3 ({GetCurrentThreadId}): {s} 941 *{Dirs[Logs]}\pop2smtp\{MMDD}debug.txt*{hh:mm:ss} smtp ({GetCurrentThreadId}): {s}

Потом перезапустите acSMTP, еще раз примите такое письмо и вышлите полученный pop2smtp\*debug.txt на support@eserv.ru в виде архива.

wikipost

foxship

02.06.2010 09:04
ред: 02.06.2010 09:11

ac пишет: вышлите полученный pop2smtp\*debug.txt на support@eserv.ru в виде архива.

Я сделал как вы сказали. Но что это нам даст я не знаю.
Время динамически не отслеживается. Видно, что сессия длилась 47 минут.
Вот начало сессии

08:56:09 pop3 (3896): +OK Hello there. 08:56:09 pop3 (3896): USER zdship@nimb.ru

......... её конец и начало следующей

08:56:09 pop3 (3896): QUIT 08:56:09 pop3 (3896): +OK Bye-bye. 09:41:01 pop3 (800): +OK Hello there. 09:41:01 pop3 (800): USER zdship@nimb.ru

wikipost

pig

02.06.2010 13:13

Следующая подкрутка — в тех строках замените:

{hh:mm:ss}

на

{GetTime hh:mm:ss}

Будет следить за временем.

wikipost

foxship

02.06.2010 14:55
ред: 02.06.2010 14:56

pig пишет: Будет следить за временем.

Все сделал как сказали. Лог сессии отправил на саппорт.

wikipost

foxship

02.06.2010 16:58
ред: 02.06.2010 17:09

Вот! Удалось получить с Лотуса. Паралельно выслал на саппорт.

02.06.2010 17:25:06,80 [0DF8:0006-035C] SMTP CIServ Listen> Connection Accepted on Port 25 for Session 21040001 02.06.2010 17:25:06,80 [0DF8:0008-16A8] SMTP CITask StateMachine> Sent 112 bytes to 192.168.0.24 [0DF8:0008-16A8] S: 220 server-ld.zdship.gor ESMTP Service (Lotus Domino Release 6.5.4FP1) ready at Wed, 2 Jun 2010 17:25:06 +0400<CRLF> 02.06.2010 17:25:06,80 [0DF8:0008-16A8] SMTP CITask StateMachine> Received 13 bytes from 192.168.0.24 [0DF8:0008-16A8] R: HELO pop2smtp 02.06.2010 17:25:06,81 [0DF8:0008-15A8] SMTP CITask StateMachine> Sent 79 bytes to 192.168.0.24 [0DF8:0008-15A8] S: 250 server-ld.zdship.gor Hello pop2smtp ([192.168.0.24]), pleased to meet you<CRLF> 02.06.2010 17:25:06 SMTP Server [0DF8:0008-15A8] State change from Greeting to Greeting 02.06.2010 17:25:06 SMTP Server [0DF8:0008-15A8] Processing in Greeting state 02.06.2010 17:25:06 SMTP Server [0DF8:0008-15A8] State change from Greeting to Connected 02.06.2010 17:25:06 SMTP Server: server-gk.zdship.gor (192.168.0.24) connected 02.06.2010 17:25:06 SMTP Server [0DF8:0008-16A8] Processing in Connected state 02.06.2010 17:25:06 SMTP Server [0DF8:0008-16A8] HELO command received 02.06.2010 17:25:07,05 [0DF8:0008-15A8] SMTP CITask StateMachine> Received 29 bytes from 192.168.0.24 [0DF8:0008-15A8] R: MAIL FROM:<FoxShip@yandex.ru> 02.06.2010 17:25:07,05 [0DF8:0008-16A8] SMTP CITask StateMachine> Sent 36 bytes to 192.168.0.24 [0DF8:0008-16A8] S: 250 FoxShip@yandex.ru... Sender OK<CRLF> 02.06.2010 17:25:07,05 [0DF8:0008-16A8] SMTP CITask StateMachine> Received 27 bytes from 192.168.0.24 [0DF8:0008-16A8] R: RCPT TO:<foxship@zdship.ru> 02.06.2010 17:25:07,05 [0DF8:0008-15A8] SMTP CITask StateMachine> Sent 39 bytes to 192.168.0.24 [0DF8:0008-15A8] S: 250 foxship@zdship.ru... Recipient OK<CRLF> 02.06.2010 17:25:07,06 [0DF8:0008-15A8] SMTP CITask StateMachine> Received 4 bytes from 192.168.0.24 [0DF8:0008-15A8] R: DATA 02.06.2010 17:25:07,06 [0DF8:0008-16A8] SMTP CITask StateMachine> Sent 53 bytes to 192.168.0.24 [0DF8:0008-16A8] S: 354 Enter message, end with "." on a line by itself<CRLF> 02.06.2010 17:25:07 SMTP Server [0DF8:0008-15A8] Processing in Connected state 02.06.2010 17:25:07 SMTP Server [0DF8:0008-15A8] MAIL command received 02.06.2010 17:25:07 SMTP Server [0DF8:0008-16A8] Processing in Connected state 02.06.2010 17:25:07 SMTP Server [0DF8:0008-16A8] RCPT command received 02.06.2010 17:25:07 SMTP Server [0DF8:0008-15A8] Processing in Connected state 02.06.2010 17:25:07 SMTP Server [0DF8:0008-15A8] DATA command received 02.06.2010 17:44:14 SMTP Server [0DF8:0008-16A8] Processing in Connected state 02.06.2010 17:44:14 SMTP Server [0DF8:0008-16A8] DATA command (cont.) 02.06.2010 17:44:16,64 [0DF8:0008-15A8] SMTP CITask StateMachine> Sent 35 bytes to 192.168.0.24 [0DF8:0008-15A8] S: 250 Message accepted for delivery<CRLF> 02.06.2010 17:44:16,70 [0DF8:0008-15A8] SMTP CITask StateMachine> Received 4 bytes from 192.168.0.24 [0DF8:0008-15A8] R: QUIT 02.06.2010 17:44:16,70 [0DF8:0008-16A8] SMTP CITask StateMachine> Sent 68 bytes to 192.168.0.24 [0DF8:0008-16A8] S: 221 server-ld.zdship.gor SMTP Service closing transmission channel<CRLF> 02.06.2010 17:44:16 SMTP Server: Message 004B75EF (MessageID: <4141275485117@web107.yandex.ru>) received 02.06.2010 17:44:16 SMTP Server [0DF8:0008-15A8] Processing in Connected state 02.06.2010 17:44:16 SMTP Server [0DF8:0008-15A8] QUIT command received 02.06.2010 17:44:16 SMTP Server [0DF8:0008-15A8] State change from Connected to Terminal 02.06.2010 17:44:16 SMTP Server: server-gk.zdship.gor (192.168.0.24) disconnected. 1 message[s] received 02.06.2010 17:44:20,10 [0DF8:0006-035C] SMTP CIServ Listen> Connection Accepted on Port 25 for Session 21120008 02.06.2010 17:44:20,10 [0DF8:0008-15A8] SMTP CITask StateMachine> Sent 112 bytes to 192.168.0.24 [0DF8:0008-15A8] S: 220 server-ld.zdship.gor ESMTP Service (Lotus Domino Release 6.5.4FP1) ready at Wed, 2 Jun 2010 17:44:20 +0400<CRLF> 02.06.2010 17:44:20,11 [0DF8:0008-15A8] SMTP CITask StateMachine> Received 13 bytes from 192.168.0.24 [0DF8:0008-15A8] R: HELO pop2smtp 02.06.2010 17:44:20,11 [0DF8:0008-16A8] SMTP CITask StateMachine> Sent 79 bytes to 192.168.0.24 [0DF8:0008-16A8] S: 250 server-ld.zdship.gor Hello pop2smtp ([192.168.0.24]), pleased to meet you<CRLF> 02.06.2010 17:44:20 SMTP Server [0DF8:0008-16A8] State change from Greeting to Greeting 02.06.2010 17:44:20 SMTP Server [0DF8:0008-16A8] Processing in Greeting state 02.06.2010 17:44:20 SMTP Server [0DF8:0008-16A8] State change from Greeting to Connected 02.06.2010 17:44:20 SMTP Server: server-gk.zdship.gor (192.168.0.24) connected 02.06.2010 17:44:20 SMTP Server [0DF8:0008-15A8] Processing in Connected state 02.06.2010 17:44:20 SMTP Server [0DF8:0008-15A8] HELO command received 02.06.2010 17:44:20,41 [0DF8:0008-16A8] SMTP CITask StateMachine> Received 35 bytes from 192.168.0.24 [0DF8:0008-16A8] R: MAIL FROM:<valery.shupta@gmail.com> 02.06.2010 17:44:20,41 [0DF8:0008-15A8] SMTP CITask StateMachine> Sent 42 bytes to 192.168.0.24 [0DF8:0008-15A8] S: 250 valery.shupta@gmail.com... Sender OK<CRLF> 02.06.2010 17:44:20,42 [0DF8:0008-15A8] SMTP CITask StateMachine> Received 43 bytes from 192.168.0.24 [0DF8:0008-15A8] R: RCPT TO:<renat.fatkhullin@aerotitanium.com> 02.06.2010 17:44:20,42 [0DF8:0008-16A8] SMTP CITask StateMachine> Sent 40 bytes to 192.168.0.24 [0DF8:0008-16A8] S: 554 Relay rejected for policy reasons.<CRLF> 02.06.2010 17:44:20,42 [0DF8:0008-16A8] SMTP CITask StateMachine> Received 35 bytes from 192.168.0.24 [0DF8:0008-16A8] R: RCPT TO:<dmitri.g.ivanov@gmail.com> 02.06.2010 17:44:20,42 [0DF8:0008-15A8] SMTP CITask StateMachine> Sent 40 bytes to 192.168.0.24 [0DF8:0008-15A8] S: 554 Relay rejected for policy reasons.<CRLF> 02.06.2010 17:44:20,44 [0DF8:0008-15A8] SMTP CITask StateMachine> Received 25 bytes from 192.168.0.24 [0DF8:0008-15A8] R: RCPT TO:<capok@zdship.ru> 02.06.2010 17:44:20,44 [0DF8:0008-16A8] SMTP CITask StateMachine> Sent 37 bytes to 192.168.0.24 [0DF8:0008-16A8] S: 250 capok@zdship.ru... Recipient OK<CRLF> 02.06.2010 17:44:20,44 [0DF8:0008-16A8] SMTP CITask StateMachine> Received 28 bytes from 192.168.0.24 [0DF8:0008-16A8] R: RCPT TO:<kuzyakin@zdship.ru> 02.06.2010 17:44:20,47 [0DF8:0008-15A8] SMTP CITask StateMachine> Sent 40 bytes to 192.168.0.24 [0DF8:0008-15A8] S: 250 kuzyakin@zdship.ru... Recipient OK<CRLF> 02.06.2010 17:44:20,49 [0DF8:0008-15A8] SMTP CITask StateMachine> Received 4 bytes from 192.168.0.24 [0DF8:0008-15A8] R: DATA 02.06.2010 17:44:20 SMTP Server [0DF8:0008-16A8] Processing in Connected state 02.06.2010 17:44:20 SMTP Server [0DF8:0008-16A8] MAIL command received 02.06.2010 17:44:20 SMTP Server [0DF8:0008-15A8] Processing in Connected state 02.06.2010 17:44:20 SMTP Server [0DF8:0008-15A8] RCPT command received 02.06.2010 17:44:20 SMTP Server [0DF8:0008-15A8] Attempt to relay mail from server-gk.zdship.gor (192.168.0.24) rejected for policy reasons. Relays from host denied in your configuration. 02.06.2010 17:44:20 SMTP Server [0DF8:0008-15A8] 554 Relay rejected for policy reasons. 02.06.2010 17:44:20 SMTP Server [0DF8:0008-16A8] Processing in Connected state 02.06.2010 17:44:20 SMTP Server [0DF8:0008-16A8] RCPT command received 02.06.2010 17:44:20 SMTP Server [0DF8:0008-16A8] Attempt to relay mail from server-gk.zdship.gor (192.168.0.24) rejected for policy reasons. Relays from host denied in your configuration. 02.06.2010 17:44:20 SMTP Server [0DF8:0008-16A8] 554 Relay rejected for policy reasons. 02.06.2010 17:44:20 SMTP Server [0DF8:0008-15A8] Processing in Connected state 02.06.2010 17:44:20 SMTP Server [0DF8:0008-15A8] RCPT command received 02.06.2010 17:44:20 SMTP Server [0DF8:0008-16A8] Processing in Connected state 02.06.2010 17:44:20 SMTP Server [0DF8:0008-16A8] RCPT command received 02.06.2010 17:44:20 SMTP Server [0DF8:0008-15A8] Processing in Connected state 02.06.2010 17:44:20 SMTP Server [0DF8:0008-15A8] DATA command received 02.06.2010 17:44:20,64 [0DF8:0008-16A8] SMTP CITask StateMachine> Sent 53 bytes to 192.168.0.24 [0DF8:0008-16A8] S: 354 Enter message, end with "." on a line by itself<CRLF> 02.06.2010 17:44:21 Router: Message 004B75EF delivered to Лукин Вячеслав Михайлович/ZDSHIP

wikipost

03.06.2010 00:50

foxship пишет: Все сделал как сказали. Лог сессии отправил на саппорт.

Лог pop2smtp на support'е не получен, приехал только лог Лотуса, сейчас разберемся.

wikipost

03.06.2010 01:06

Вот интересующий нас фрагмент:

02.06.2010 17:25:07,06 [0DF8:0008-15A8] SMTP CITask StateMachine> Received 4 bytes from 192.168.0.24 [0DF8:0008-15A8] R: DATA 02.06.2010 17:25:07,06 [0DF8:0008-16A8] SMTP CITask StateMachine> Sent 53 bytes to 192.168.0.24 [0DF8:0008-16A8] S: 354 Enter message, end with "." on a line by itself<CRLF> 02.06.2010 17:25:07 SMTP Server [0DF8:0008-15A8] Processing in Connected state 02.06.2010 17:25:07 SMTP Server [0DF8:0008-15A8] MAIL command received 02.06.2010 17:25:07 SMTP Server [0DF8:0008-16A8] Processing in Connected state 02.06.2010 17:25:07 SMTP Server [0DF8:0008-16A8] RCPT command received 02.06.2010 17:25:07 SMTP Server [0DF8:0008-15A8] Processing in Connected state 02.06.2010 17:25:07 SMTP Server [0DF8:0008-15A8] DATA command received 02.06.2010 17:44:13,91 [0DF8:0008-16A8] SMTP CITask StateMachine> ITASK_STATE_RECV_TEXT: Received total line 5, 1 bytes from 192.168.0.24 02.06.2010 17:44:14 SMTP Server [0DF8:0008-16A8] Processing in Connected state 02.06.2010 17:44:14 SMTP Server [0DF8:0008-16A8] DATA command (cont.) 02.06.2010 17:44:16,64 [0DF8:0008-15A8] SMTP CITask StateMachine> Sent 35 bytes to 192.168.0.24 [0DF8:0008-15A8] S: 250 Message accepted for delivery<CRLF>

Задержка 19 минут на стадии DATA, т.е. либо на передаче тела письма, либо на стадии обработки до выдачи ответа 250. Что Лотус делал в эти 19 минут — странно, что не записал в лог. В предыдущих командах он отчитывался буквально за каждый байт. Мне лог нашего pop2smtp был бы всё-таки понятнее.

wikipost

03.06.2010 03:01

foxship пишет: Все сделал как сказали. Лог сессии отправил на саппорт.

Поискал в SMTP-логе попытки доставки других писем с того же вашего email'а (с yandex.ru), с которого пришел лог Лотуса в 18ч — не было таких попыток. Вам то письмо с логом не вернулось обратно?

wikipost

foxship

03.06.2010 07:04
ред: 03.06.2010 07:10

ac пишет: Что Лотус делал в эти 19 минут — странно, что не записал в лог.

Дело как и предполагалось не лотусе а в Антивирусе Касперского 6.0 для Windows Servers Enterprise Edition, который не сервере где установлен Eserv3. Отключил — письмо (20Mb) дошло за 4 минуты. Что делает остальные 45 минут каспер с нашим письмом совершенно непонятно.

Можно ли как нибудь воспрепятствовать данному явлению не отключая каспера?
Какое отношение имеет AntivirusKAV в Опции и списки Eserv3 к Антивирусу Касперского 6.0 для Windows Servers Enterprise Edition, установленного на сервере.

wikipost

03.06.2010 08:15
ред: 03.06.2010 08:19

foxship пишет: Дело как и предполагалось не лотусе

И не в Eserv, что в контексте данного форума важнее

foxship пишет: Можно ли как нибудь воспрепятствовать данному явлению не отключая каспера?

Резидентные антивирусы, firewall'ы и прочие фильтры на сервере — безусловное ЗЛО, которое должно быть отключено. Eserv сам умеет проверять почту на вирусы и следить за безопасностью своих портов.

AntivirusKAV в Eserv как раз и служит для интеграции с антивирусами в нормальном режиме — через антивирусный API, а не через хаки перехвата функций файловой системы (как делают резидентные антивирусы). Если в Eserv антивирусная проверка не включена, то эти опции никак не конфликтуют с резидентным KAV6. Хотя сам он (резидент) может конфликтовать с чем угодно на сервере, в том числе и с Eserv, отнимая у него файлы писем, логов и конфигов в самые неподходящие моменты. Именно поэтому настоятельно рекомендуем резидентов отключать, или хотя бы исключать из их проверок каталоги Eserv.

wikipost

foxship

03.06.2010 08:34
ред: 03.06.2010 08:55

ac пишет: И не в Eserv, что в контексте данного форума важнее

Безусловно!

ac пишет: AntivirusKAV в Eserv как раз и служит для интеграции с антивирусами в нормальном режиме — через антивирусный API

Хорошо! Тогда можно ли и каким образом мне научить Eserv6 взаимодействовать с KAV6? Предварительно отключив KAV6 как резидента.

wikipost

pig

03.06.2010 11:55

Я думаю, вам надо смотреть в сторону антивируса для Домино. Потому как pop2smtp — прозрачная качалка, там антивируса нет. Касаемо KAV надо смотреть, что он там делает. Рабочие каталоги Eserv надо из-под проверки файлового монитора вывести, как и почтовый трафик из-под проверки почтового монитора (не знаю, есть ли он в 6 EE, вполне может быть).

wikipost

leka

03.06.2010 11:55

ac пишет: Резидентные антивирусы, firewall'ы и прочие фильтры на сервере — безусловное ЗЛО, которое должно быть отключено.

Я с этим не согласен, должны стоять... и работать... но, и смотря как это все настроенно...

ac пишет: Eserv сам умеет проверять почту на вирусы и следить за безопасностью своих портов.

ac пишет: Именно поэтому настоятельно рекомендуем резидентов отключать, или хотя бы исключать из их проверок каталоги Eserv.

Что и сделано...

wikipost

foxship

03.06.2010 12:37
ред: 03.06.2010 12:38

leka пишет: Что и сделано...

А на включение антивируса KAV разве не нужен ключ с лицензией? Как с этим быть? И где можно прочитать как настроить KAV на Eserv3 для почты. В интернет документации почти ничего не сказано.

wikipost

leka

03.06.2010 13:23

foxship пишет: А на включение антивируса KAV разве не нужен ключ с лицензией?

Как не нужен? Нужен...

foxship пишет: Как с этим быть? И где можно прочитать как настроить KAV на Eserv3 для почты. В интернет документации почти ничего не сказано.

http://www.eserv.ru/AntivirusKav
А здесь не смотрели...

wikipost

foxship

03.06.2010 13:29
ред: 03.06.2010 13:31

leka пишет: Как не нужен? Нужен...

На

http://www.eserv.ru/AntivirusKav прочитал

Для испытания работы антивируса KAV в Eserv/3 требуется оценочный ключ: KavTrialEserv3.key

Вот этот или его нужно пробрести какой нибудь коммерческий ключ?

leka пишет: http://www.eserv.ru/AntivirusKav
А здесь не смотрели...

Так я и говорю, что там почти ничего не сказано. Или этого достаточно для настройки?

wikipost

leka

03.06.2010 13:53
ред: 03.06.2010 13:55

foxship пишет: Вот этот

foxship пишет: Или этого достаточно для настройки?

foxship пишет: Для испытания работы антивируса KAV в Eserv/3 требуется оценочный ключ: KavTrialEserv3.key

foxship пишет: или его нужно пробрести какой нибудь коммерческий ключ?

После того как настроили и удолетворяет работа плагина, выписываете счет на коммерческий ключ и тем самым приобретаете подписку на год работы этого плагина...

wikipost

foxship

03.06.2010 14:01
ред: 03.06.2010 14:43

leka пишет: После того как настроили и удолетворяет работа плагина выписываете счет и приобретаете подписку на год работы этого плагина...

Так я и думал. Плохо. Финасовый год только начался. А на этот год KAV незапланирован.

wikipost

03.06.2010 20:21

pig пишет: Я думаю, вам надо смотреть в сторону антивируса для Домино. Потому как pop2smtp — прозрачная качалка, там антивируса нет.

Верно. Либо настроить pop2smtp доставлять почту на Eserv (как это и работает у большинства), а уже сам Eserv после проверки антивирусом может доставлять на Лотус.

leka пишет: ac пишет: Резидентные антивирусы, firewall'ы и прочие фильтры на сервере — безусловное ЗЛО, которое должно быть отключено.

Я с этим не согласен, должны стоять... и работать... но, и смотря как это все настроенно...

Увы, никакие настройки не избавят вас от тех хакерских трюков, которые резидентные антивирусы проделывают с ОС. Даже если вы отключаете мониторинг заданных каталогов, антивирус продолжает фильтровать все обращения к файловой системе — и вот в этих "перехватчиках" регулярно всплывают баги. Так несколько месяцев назад мы с разработчиками "ФинАналитика" (внутри которого сидит наш acWEB и интерфейсная оболочка) несколько дней ловили странный баг, который проявлялся у некоторых их клиентов. Выяснилось, что виноват Kaspersky Internet Security — его обработчики системных вызовов возвращали приложению ошибку 87 ("неправильные параметры"), хотя сам Windows такие же параметры неправильными не считал (и они неправильными и не были). Пришлось в Eserv обходить этот баг KAV — "Can't create PID-file (err=87)! Antivirus?". И это далеко не единичный случай порчи работоспособности ОС антивирусом.

То же самое с мониторами сети в антивирусах. Dr.WEB неоднократно уличался в порче почтовых протоколов. А уж про злодеяния firewall'ов я могу писать многие тома — проблемы, вызываемые firewall'ами приходится решать КАЖДЫЙ ДЕНЬ, причем часто по нескольку раз в день. Это так наболело, что я давно написал злобную статью

http://www.eserv.ru/FireWall для выпуска пара, но каждый день хочется поколотить кого-нибудь из разработчиков firewall'ов.

wikipost

03.06.2010 20:32

foxship пишет: Финасовый год только начался. А на этот год KAV незапланирован.

Можно попробовать отключить отладочный лог pop2smtp (Pop2SmtpDebug=0). Мне кажется, это его KAV многократно перепроверяет по мере того как pop2smtp дописывает туда тело письма.

wikipost

Eserv Forum / E3 / Eserv 3 Mail Server Support / Долго доставляются письма